[INFO] cloning repository https://github.com/jcn363/open_crust
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/jcn363/open_crust" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjcn363%2Fopen_crust", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjcn363%2Fopen_crust'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 4c21307140eb32bf47e9574e9a4df437e917dc28
[INFO] testing jcn363/open_crust against try#28f95933dc0727396c07b48d0a8cb5211b7fb472 for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjcn363%2Fopen_crust" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/jcn363/open_crust
[INFO] finished tweaking git repo https://github.com/jcn363/open_crust
[INFO] tweaked toml for git repo https://github.com/jcn363/open_crust written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/jcn363/open_crust on toolchain 28f95933dc0727396c07b48d0a8cb5211b7fb472
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/jcn363/open_crust already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 785d886c056c30a06830bc18ab60fb5aed8f2b40adcf4be1230c1aa734d4f562
[INFO] running `Command { std: "docker" "start" "-a" "785d886c056c30a06830bc18ab60fb5aed8f2b40adcf4be1230c1aa734d4f562", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "785d886c056c30a06830bc18ab60fb5aed8f2b40adcf4be1230c1aa734d4f562", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "785d886c056c30a06830bc18ab60fb5aed8f2b40adcf4be1230c1aa734d4f562", kill_on_drop: false }`
[INFO] [stdout] 785d886c056c30a06830bc18ab60fb5aed8f2b40adcf4be1230c1aa734d4f562
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5f8b3e1f0559674f4dcb58e78f58f643929e73396b3d70893d00691a3892aaff
[INFO] running `Command { std: "docker" "start" "-a" "5f8b3e1f0559674f4dcb58e78f58f643929e73396b3d70893d00691a3892aaff", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling siphasher v1.0.3
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling dunce v1.0.5
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling unicode-segmentation v1.13.2
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling rand v0.8.6
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling aws-lc-rs v1.16.3
[INFO] [stderr]    Compiling litemap v0.8.2
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling new_debug_unreachable v1.0.6
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling phf_shared v0.11.3
[INFO] [stderr]    Compiling icu_normalizer_data v2.2.0
[INFO] [stderr]    Compiling icu_properties_data v2.2.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling rustls-pki-types v1.14.1
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling mac v0.1.1
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling hashbrown v0.17.1
[INFO] [stderr]    Compiling futf v0.1.5
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling castaway v0.2.4
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling utf-8 v0.7.6
[INFO] [stderr]    Compiling instability v0.3.12
[INFO] [stderr]    Compiling simd-adler32 v0.3.9
[INFO] [stderr]    Compiling rustls v0.23.40
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling precomputed-hash v0.1.1
[INFO] [stderr]    Compiling phf_generator v0.11.3
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling futures-task v0.3.32
[INFO] [stderr]    Compiling indoc v2.0.7
[INFO] [stderr]    Compiling string_cache_codegen v0.5.4
[INFO] [stderr]    Compiling phf_codegen v0.11.3
[INFO] [stderr]    Compiling compact_str v0.9.0
[INFO] [stderr]    Compiling tendril v0.4.3
[INFO] [stderr]    Compiling lru v0.16.4
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling convert_case v0.10.0
[INFO] [stderr]    Compiling phf v0.11.3
[INFO] [stderr]    Compiling typenum v1.20.0
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling adler2 v2.0.1
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling num-conv v0.2.1
[INFO] [stderr]    Compiling litrs v1.0.0
[INFO] [stderr]    Compiling markup5ever v0.12.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling fdeflate v0.3.7
[INFO] [stderr]    Compiling line-clipping v0.3.7
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling document-features v0.2.12
[INFO] [stderr]    Compiling cc v1.2.62
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling signal-hook-mio v0.2.5
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling option-ext v0.2.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling pxfm v0.1.29
[INFO] [stderr]    Compiling ipnet v2.12.0
[INFO] [stderr]    Compiling unicode-truncate v2.0.1
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling rustls-native-certs v0.8.3
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling png v0.18.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling byteorder-lite v0.1.0
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling x11rb-protocol v0.13.2
[INFO] [stderr]    Compiling bytemuck v1.25.0
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling directories v6.0.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling shell-words v1.1.1
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling aws-lc-sys v0.40.0
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling darling_core v0.23.0
[INFO] [stderr]    Compiling moxcms v0.8.1
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling html5ever v0.27.0
[INFO] [stderr]    Compiling gethostname v1.1.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling async-recursion v1.1.1
[INFO] [stderr]    Compiling zerofrom v0.1.7
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling crossterm v0.29.0
[INFO] [stderr]    Compiling kasuari v0.4.12
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling x11rb v0.13.2
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling ratatui-core v0.1.0
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling string_cache v0.8.9
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling ratatui-widgets v0.3.0
[INFO] [stderr]    Compiling ratatui-crossterm v0.1.0
[INFO] [stderr]    Compiling image v0.25.10
[INFO] [stderr]    Compiling xml5ever v0.18.1
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling ratatui-macros v0.7.0
[INFO] [stderr]    Compiling ratatui v0.30.0
[INFO] [stderr]    Compiling markup5ever_rcdom v0.3.0
[INFO] [stderr]    Compiling html2md v0.2.15
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling arboard v3.6.1
[INFO] [stderr]    Compiling tower-http v0.6.10
[INFO] [stderr]    Compiling h2 v0.4.14
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling rustls-platform-verifier v0.7.0
[INFO] [stderr]    Compiling hyper-rustls v0.27.9
[INFO] [stderr]    Compiling reqwest v0.13.3
[INFO] [stderr]    Compiling opencrust v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 06s
[INFO] running `Command { std: "docker" "inspect" "5f8b3e1f0559674f4dcb58e78f58f643929e73396b3d70893d00691a3892aaff", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5f8b3e1f0559674f4dcb58e78f58f643929e73396b3d70893d00691a3892aaff", kill_on_drop: false }`
[INFO] [stdout] 5f8b3e1f0559674f4dcb58e78f58f643929e73396b3d70893d00691a3892aaff
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 898e651815d20a03555afcff5bf5b9cc18b360c36631e1e1e72b4b3508236a55
[INFO] running `Command { std: "docker" "start" "-a" "898e651815d20a03555afcff5bf5b9cc18b360c36631e1e1e72b4b3508236a55", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling rayon-core v1.13.0
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling gethostname v1.1.0
[INFO] [stderr]    Compiling crossterm v0.29.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling x11rb v0.13.2
[INFO] [stderr]    Compiling string_cache v0.8.9
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling markup5ever v0.12.1
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling reqwest v0.13.3
[INFO] [stderr]    Compiling ratatui-crossterm v0.1.0
[INFO] [stderr]    Compiling ratatui v0.30.0
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling arboard v3.6.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling xml5ever v0.18.1
[INFO] [stderr]    Compiling html5ever v0.27.0
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling markup5ever_rcdom v0.3.0
[INFO] [stderr]    Compiling html2md v0.2.15
[INFO] [stderr]    Compiling opencrust v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 47.74s
[INFO] running `Command { std: "docker" "inspect" "898e651815d20a03555afcff5bf5b9cc18b360c36631e1e1e72b4b3508236a55", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "898e651815d20a03555afcff5bf5b9cc18b360c36631e1e1e72b4b3508236a55", kill_on_drop: false }`
[INFO] [stdout] 898e651815d20a03555afcff5bf5b9cc18b360c36631e1e1e72b4b3508236a55
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 4c94f15cc7b5e435bc9906f6b7d66ab9570cd04362d47bf5d9469ae8cb464cc4
[INFO] running `Command { std: "docker" "start" "-a" "4c94f15cc7b5e435bc9906f6b7d66ab9570cd04362d47bf5d9469ae8cb464cc4", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.64s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/opencrust-fbd161a4f60c38ef)
[INFO] [stdout] 
[INFO] [stdout] running 352 tests
[INFO] [stdout] test app::tests::change_status_variants ... ok
[INFO] [stdout] test app::tests::background_task_status_starts_running ... ok
[INFO] [stdout] test app::tests::app_plan_mode_default_disabled ... ok
[INFO] [stdout] test app::tests::app_enter_insert_mode ... ok
[INFO] [stdout] test app::tests::handle_backspace_removes_last_char ... ok
[INFO] [stdout] test app::tests::history_up_navigates_entries ... ok
[INFO] [stdout] test app::tests::message_new_sets_timestamp ... ok
[INFO] [stdout] test app::tests::mode_debug_and_clone ... ok
[INFO] [stdout] test app::tests::message_new_sets_content ... ok
[INFO] [stdout] test app::tests::plan_mode_default_is_disabled ... ok
[INFO] [stdout] test app::tests::plan_mode_partial_eq ... ok
[INFO] [stdout] test app::tests::proposed_change_defaults_to_pending ... ok
[INFO] [stdout] test app::tests::handle_bg_completion_updates_task ... ok
[INFO] [stdout] test app::tests::app_default_mode_is_normal ... ok
[INFO] [stdout] test app::tests::history_up_stays_at_zero ... ok
[INFO] [stdout] test app::tests::app_enter_normal_mode ... ok
[INFO] [stdout] test app::tests::handle_backspace_empty_does_nothing ... ok
[INFO] [stdout] test app::tests::task_status_variants ... ok
[INFO] [stdout] test app::tests::handle_bg_unknown_notification_returns_none ... ok
[INFO] [stdout] test app::tests::history_down_clears_at_end ... ok
[INFO] [stdout] test app::tests::history_down_moves_forward ... ok
[INFO] [stdout] test app::tests::handle_bg_malformed_notification_returns_none ... ok
[INFO] [stdout] test app::tests::handle_bg_failure_updates_task ... ok
[INFO] [stdout] test app::tests::clear_ghost_text_clears_state ... ok
[INFO] [stdout] test app::tests::history_down_none_does_nothing ... ok
[INFO] [stdout] test app::tests::vim_cursor_right_increments ... ok
[INFO] [stdout] test app::tests::handle_char_appends_to_input ... ok
[INFO] [stdout] test audit::tests::test_export_csv ... ok
[INFO] [stdout] test audit::tests::test_export_json ... ok
[INFO] [stdout] test audit::tests::test_export_to_file ... ok
[INFO] [stdout] test audit::tests::test_matches_date_filter ... ok
[INFO] [stdout] test audit::tests::test_parse_entry_malformed ... ok
[INFO] [stdout] test audit::tests::test_parse_entry_missing_brackets ... ok
[INFO] [stdout] test audit::tests::test_parse_entry_short_timestamp ... ok
[INFO] [stdout] test audit::tests::test_parse_entry_valid ... ok
[INFO] [stdout] test background_agents::tests::test_agent_creation ... ok
[INFO] [stdout] test background_agents::tests::test_agent_display_pending ... ok
[INFO] [stdout] test background_agents::tests::test_cancel_checks_status ... ok
[INFO] [stdout] test background_agents::tests::test_log_ring_buffer ... ok
[INFO] [stdout] test background_agents::tests::test_manager_empty_on_create ... ok
[INFO] [stdout] test app::tests::history_up_empty_does_nothing ... ok
[INFO] [stdout] test background_agents::tests::test_stats_default ... ok
[INFO] [stdout] test compliance::tests::test_compliance_profile_display ... ok
[INFO] [stdout] test compliance::tests::test_compliance_profile_evaluate ... ok
[INFO] [stdout] test compliance::tests::test_evidence_package_verify ... ok
[INFO] [stdout] test compliance::tests::test_policy_enterprise_default ... ok
[INFO] [stdout] test compliance::tests::test_policy_evaluation ... ok
[INFO] [stdout] test compliance::tests::test_policy_violation_display ... ok
[INFO] [stdout] test compliance::tests::test_report_empty_entries ... ok
[INFO] [stdout] test compliance::tests::test_report_to_csv ... ok
[INFO] [stdout] test compliance::tests::test_report_to_html ... ok
[INFO] [stdout] test compliance::tests::test_report_to_json ... ok
[INFO] [stdout] test compliance::tests::test_report_with_entries ... ok
[INFO] [stdout] test compliance::tests::test_soc2_profile ... ok
[INFO] [stdout] test background_agents::tests::test_manager_stats_empty ... ok
[INFO] [stdout] test config::tests::config_context_limit_anthropic_other ... ok
[INFO] [stdout] test config::tests::config_context_limit_anthropic_sonnet ... ok
[INFO] [stdout] test config::tests::config_context_limit_gemini_2_5 ... ok
[INFO] [stdout] test config::tests::config_context_limit_ollama_default ... ok
[INFO] [stdout] test config::tests::config_context_limit_returns_custom_budget ... ok
[INFO] [stdout] test config::tests::config_default_values ... ok
[INFO] [stdout] test config::tests::config_summarization_threshold_custom ... ok
[INFO] [stdout] test config::tests::config_validate_empty_model_should_warn ... ok
[INFO] [stdout] test config::tests::config_summarization_threshold_default ... ok
[INFO] [stdout] test config::tests::config_validate_invalid_theme_should_warn ... ok
[INFO] [stdout] test config::tests::config_validate_lsp_empty_command_should_warn ... ok
[INFO] [stdout] test config::tests::config_validate_mcp_empty_command_should_warn ... ok
[INFO] [stdout] test config::tests::config_validate_ollama_with_url_should_pass ... ok
[INFO] [stdout] test config::tests::config_validate_theme_hex_colors ... ok
[INFO] [stdout] test app::tests::vim_cursor_left_stays_at_zero ... ok
[INFO] [stdout] test config::tests::paid_model_no_key_warns ... ok
[INFO] [stdout] test config::tests::free_model_no_key_no_warning ... ok
[INFO] [stdout] test config::tests::config_validate_lsp_no_extensions_should_warn ... ok
[INFO] [stdout] test config::tests::provider_type_display_all_variants ... ok
[INFO] [stdout] test config::tests::response_mode_as_str_all_variants ... ok
[INFO] [stdout] test config::tests::validate_all_providers_missing_keys ... ok
[INFO] [stdout] test config::tests::validate_hex_color_accepts_valid_colors ... ok
[INFO] [stdout] test config::tests::provider_type_from_str_unknown_should_error ... ok
[INFO] [stdout] test config::tests::validate_hex_color_rejects_invalid_colors ... ok
[INFO] [stdout] test custom_commands::tests::test_execute_unknown_command ... ok
[INFO] [stdout] test custom_commands::tests::test_has_command_returns_false_for_unknown ... ok
[INFO] [stdout] test custom_commands::tests::test_list_commands_empty ... ok
[INFO] [stdout] test custom_commands::tests::test_new_manager_is_empty ... ok
[INFO] [stdout] test desktop::detection::tests::test_theme_defaults ... ok
[INFO] [stdout] test config::tests::provider_type_from_str_all_variants ... ok
[INFO] [stdout] test desktop::file_picker::tests::test_filter ... ok
[INFO] [stdout] test desktop::file_picker::tests::test_result ... ok
[INFO] [stdout] test desktop::detection::tests::test_desktop_detection ... ok
[INFO] [stdout] test desktop::notifications::tests::test_notification_creation ... ok
[INFO] [stdout] test desktop::file_picker::tests::test_backend_detection ... ok
[INFO] [stdout] test desktop::notifications::tests::test_urgency ... ok
[INFO] [stdout] test formatters::tests::test_format_file_no_formatter_for_extension ... ok
[INFO] [stdout] test desktop::tests::test_module_structure ... ok
[INFO] [stdout] test formatters::tests::test_has_formatter_known_extensions ... ok
[INFO] [stdout] test formatters::tests::test_has_formatter_unknown_extension ... ok
[INFO] [stdout] test formatters::tests::test_supported_extensions_not_empty ... ok
[INFO] [stdout] test json_utils::tests::compact_should_error_on_invalid_input ... ok
[INFO] [stdout] test json_utils::tests::compact_should_remove_whitespace ... ok
[INFO] [stdout] test json_utils::tests::compare_different_json_should_show_diffs ... ok
[INFO] [stdout] test json_utils::tests::compare_equal_json_should_return_equal ... ok
[INFO] [stdout] test json_utils::tests::compare_json_should_error_on_invalid_input ... ok
[INFO] [stdout] test json_utils::tests::compare_json_with_missing_keys_should_report ... ok
[INFO] [stdout] test json_utils::tests::format_should_error_on_invalid_input ... ok
[INFO] [stdout] test json_utils::tests::format_should_pretty_print ... ok
[INFO] [stdout] test json_utils::tests::get_array_length_should_error_on_invalid_json ... ok
[INFO] [stdout] test json_utils::tests::get_array_length_should_error_on_non_array ... ok
[INFO] [stdout] test json_utils::tests::get_array_length_should_return_count ... ok
[INFO] [stdout] test json_utils::tests::get_array_length_should_return_zero_for_empty ... ok
[INFO] [stdout] test json_utils::tests::get_keys_should_error_on_invalid_json ... ok
[INFO] [stdout] test json_utils::tests::get_keys_should_error_on_non_object ... ok
[INFO] [stdout] test json_utils::tests::get_keys_should_return_all_keys ... ok
[INFO] [stdout] test json_utils::tests::get_path_should_error_on_invalid_json ... ok
[INFO] [stdout] test json_utils::tests::get_path_should_error_on_missing_path ... ok
[INFO] [stdout] test json_utils::tests::get_path_should_navigate_nested_objects ... ok
[INFO] [stdout] test json_utils::tests::get_path_should_return_top_level_key ... ok
[INFO] [stdout] test json_utils::tests::merge_should_add_new_keys ... ok
[INFO] [stdout] test json_utils::tests::merge_should_error_on_invalid_base ... ok
[INFO] [stdout] test json_utils::tests::merge_should_error_on_invalid_patch ... ok
[INFO] [stdout] test json_utils::tests::merge_should_overwrite_existing_keys ... ok
[INFO] [stdout] test json_utils::tests::set_path_should_add_new_field ... ok
[INFO] [stdout] test json_utils::tests::set_path_should_error_on_array_index_out_of_bounds ... ok
[INFO] [stdout] test json_utils::tests::set_path_should_error_on_invalid_json ... ok
[INFO] [stdout] test json_utils::tests::set_path_should_replace_existing_field ... ok
[INFO] [stdout] test json_utils::tests::set_path_should_update_array_element ... ok
[INFO] [stdout] test json_utils::tests::to_csv_should_error_on_non_array ... ok
[INFO] [stdout] test json_utils::tests::to_csv_should_escape_commas ... ok
[INFO] [stdout] test json_utils::tests::to_csv_should_produce_header_and_rows ... ok
[INFO] [stdout] test json_utils::tests::to_csv_should_return_empty_for_empty_array ... ok
[INFO] [stdout] test json_utils::tests::validate_should_accept_valid_json ... ok
[INFO] [stdout] test json_utils::tests::validate_should_reject_invalid_json ... ok
[INFO] [stdout] test llm::tests::goal_creation ... ok
[INFO] [stdout] test desktop::notifications::tests::test_notification_options ... ok
[INFO] [stdout] test app::tests::submit_message_empty_does_nothing ... ok
[INFO] [stdout] test app::tests::submit_message_does_not_duplicate_history ... ok
[INFO] [stdout] test app::tests::vim_prev_word_goes_back ... ok
[INFO] [stdout] test app::tests::tabs_initialized_with_chat_and_tasks ... ok
[INFO] [stdout] test llm::tests::plan_mode_state_debug ... ok
[INFO] [stdout] test llm::tests::plan_mode_state_default_is_disabled ... ok
[INFO] [stdout] test llm::tests::plan_mode_state_partial_eq ... ok
[INFO] [stdout] test app::tests::vim_line_end ... ok
[INFO] [stdout] test app::tests::vim_line_start ... ok
[INFO] [stdout] test llm::tests::goal_get_prompt_contains_description ... ok
[INFO] [stdout] test markdown::tests::test_count_words ... ok
[INFO] [stdout] test markdown::tests::test_links ... ok
[INFO] [stdout] test markdown::tests::test_title ... ok
[INFO] [stdout] test mcp::tests::test_resolve_spawn_command_empty ... ok
[INFO] [stdout] test mcp::tests::test_resolve_spawn_command_npx ... ok
[INFO] [stdout] test mcp::tests::test_resolve_spawn_command_simple ... ok
[INFO] [stdout] test mcp_showcase::tests::test_get_server_description ... ok
[INFO] [stdout] test mcp_showcase::tests::test_module_structure ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_handle_key_down ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_handle_key_down_at_bottom ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_handle_key_enter_toggle ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_handle_key_esc ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_handle_key_unknown ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_handle_key_up ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_render_does_not_panic ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_toggle_nonexistent_server ... ok
[INFO] [stdout] test mcp_showcase::tui::tests::test_toggle_server ... ok
[INFO] [stdout] test mission_control::tests::test_module_structure ... ok
[INFO] [stdout] test mission_control::tui::tests::test_compute_stats_empty ... ok
[INFO] [stdout] test app::tests::should_trigger_prediction_no_last_time ... ok
[INFO] [stdout] test mission_control::tui::tests::test_compute_stats_mixed ... ok
[INFO] [stdout] test llm::tests::goal_default_is_none ... ok
[INFO] [stdout] test mission_control::tui::tests::test_handle_key_esc ... ok
[INFO] [stdout] test mission_control::tui::tests::test_handle_key_unknown ... ok
[INFO] [stdout] test mission_control::tui::tests::test_handle_key_enter ... ok
[INFO] [stdout] test mission_control::tui::tests::test_layout_chain ... ok
[INFO] [stdout] test mission_control::tui::tests::test_initial_state ... ok
[INFO] [stdout] test mission_control::tui::tests::test_layout_diamond ... ok
[INFO] [stdout] test mission_control::tui::tests::test_layout_single ... ok
[INFO] [stdout] test mission_control::tui::tests::test_navigate_down_in_layer ... ok
[INFO] [stdout] test mission_control::tui::tests::test_layout_independent ... ok
[INFO] [stdout] test mission_control::tui::tests::test_refresh_tasks_stale_on_contention ... ok
[INFO] [stdout] test mission_control::tui::tests::test_render_detail_no_panic ... ok
[INFO] [stdout] test mission_control::tui::tests::test_select_task_returns_action ... ok
[INFO] [stdout] test mission_control::tui::tests::test_task_style_completed ... ok
[INFO] [stdout] test llm::tests::goal_no_prompt_when_not_set ... ok
[INFO] [stdout] test mission_control::tui::tests::test_task_style_pending ... ok
[INFO] [stdout] test mission_control::tui::tests::test_task_style_running ... ok
[INFO] [stdout] test mission_control::tui::tests::test_truncate_long ... ok
[INFO] [stdout] test mission_control::tui::tests::test_truncate_short ... ok
[INFO] [stdout] test models::tests::test_bundled_defaults_non_empty ... ok
[INFO] [stdout] test models::tests::test_cache_manager_create_load_missing ... ok
[INFO] [stdout] test models::tests::test_cache_manager_save_and_load ... ok
[INFO] [stdout] test models::tests::test_cache_stale_is_none ... ok
[INFO] [stdout] test mission_control::tui::tests::test_layout_empty ... ok
[INFO] [stdout] test models::tests::test_model_cache_entry_roundtrip ... ok
[INFO] [stdout] test models::tests::test_provider_model_default_tool_capable ... ok
[INFO] [stdout] test mission_control::tui::tests::test_refresh_tasks_no_bridge ... ok
[INFO] [stdout] test mission_control::tui::tests::test_task_style_failed ... ok
[INFO] [stdout] test app::tests::should_trigger_prediction_disabled ... ok
[INFO] [stdout] test app::tests::submit_message_adds_to_tab_and_history ... ok
[INFO] [stdout] test multi_repo::tests::test_manager_add_and_list ... ok
[INFO] [stdout] test multi_repo::tests::test_register_non_git_dir_fails ... ok
[INFO] [stdout] test multi_repo::tests::test_register_nonexistent_path_fails ... ok
[INFO] [stdout] test multi_repo::tests::test_manager_get ... ok
[INFO] [stdout] test multi_repo::tests::test_manager_duplicate_add_fails ... ok
[INFO] [stdout] test orchestrator::agent_pool::tests::test_cancel_nonexistent ... ok
[INFO] [stdout] test orchestrator::agent_pool::tests::test_cleanup_removes_ids ... ok
[INFO] [stdout] test orchestrator::agent_pool::tests::test_default_config ... ok
[INFO] [stdout] test orchestrator::agent_pool::tests::test_pool_starts_empty ... ok
[INFO] [stdout] test orchestrator::agent_pool::tests::test_spawn_rejected_when_full ... ok
[INFO] [stdout] test orchestrator::coordinator::tests::test_coordinator_creation ... ok
[INFO] [stdout] test orchestrator::coordinator::tests::test_coordinator_result ... ok
[INFO] [stdout] test orchestrator::coordinator::tests::test_task_dag_independent ... ok
[INFO] [stdout] test orchestrator::coordinator::tests::test_task_dag_sequential ... ok
[INFO] [stdout] test orchestrator::task::tests::test_add_dependency ... ok
[INFO] [stdout] test orchestrator::task::tests::test_is_ready_no_dependencies ... ok
[INFO] [stdout] test orchestrator::task::tests::test_is_ready_with_dependencies ... ok
[INFO] [stdout] test orchestrator::task::tests::test_summary_completed ... ok
[INFO] [stdout] test orchestrator::task::tests::test_summary_pending ... ok
[INFO] [stdout] test orchestrator::task::tests::test_task_creation ... ok
[INFO] [stdout] test orchestrator::task::tests::test_terminal_states ... ok
[INFO] [stdout] test orchestrator::tests::test_decompose_code_request ... ok
[INFO] [stdout] test orchestrator::tests::test_decompose_full_workflow ... ok
[INFO] [stdout] test orchestrator::tests::test_decompose_generic_fallback ... ok
[INFO] [stdout] test orchestrator::tests::test_decompose_research_request ... ok
[INFO] [stdout] test orchestrator::tests::test_orchestrator_dependencies_ordering ... ok
[INFO] [stdout] test permissions::tests::check_permission_exact_tool_action ... ok
[INFO] [stdout] test permissions::tests::check_permission_glob_pattern_fallback ... ok
[INFO] [stdout] test permissions::tests::check_permission_glob_wildcard_match_any ... ok
[INFO] [stdout] test multi_repo::tests::test_stats ... ok
[INFO] [stdout] test permissions::tests::check_permission_global_wildcard_rule ... ok
[INFO] [stdout] test permissions::tests::check_permission_glob_wins_over_exact_match ... ok
[INFO] [stdout] test permissions::tests::check_permission_no_match_in_map_returns_ask ... ok
[INFO] [stdout] test permissions::tests::check_permission_unknown_tool_falls_to_ask ... ok
[INFO] [stdout] test permissions::tests::is_allowed_returns_false_for_deny_or_ask ... ok
[INFO] [stdout] test permissions::tests::is_allowed_returns_true_for_allow ... ok
[INFO] [stdout] test permissions::tests::network_deny_invalid_url ... ok
[INFO] [stdout] test permissions::tests::network_deny_suffix_bypass_attempt ... ok
[INFO] [stdout] test permissions::tests::network_deny_wrong_domain ... ok
[INFO] [stdout] test multi_repo::tests::test_manager_new_is_empty ... ok
[INFO] [stdout] test permissions::tests::network_permit_empty_domain_list_allows_all ... ok
[INFO] [stdout] test planner::tests::create_plan_return_message_should_include_title_and_count ... ok
[INFO] [stdout] test permissions::tests::network_permit_exact_match ... ok
[INFO] [stdout] test planner::tests::create_plan_should_set_current_plan ... ok
[INFO] [stdout] test multi_repo::tests::test_register_repo ... ok
[INFO] [stdout] test permissions::tests::network_permit_subdomain_match ... ok
[INFO] [stdout] test llm::tests::tool_not_blocked_in_plan_mode_allows_read_tools ... ok
[INFO] [stdout] test planner::tests::mark_step_complete_without_active_plan_should_error ... ok
[INFO] [stdout] test multi_repo::tests::test_manager_remove ... ok
[INFO] [stdout] test planner::tests::new_should_create_empty_planner ... ok
[INFO] [stdout] test plugins::tests::test_discover_plugins ... ok
[INFO] [stdout] test plugins::tests::test_enable_disable ... ok
[INFO] [stdout] test llm::tests::tool_not_blocked_when_disabled ... ok
[INFO] [stdout] test app::tests::vim_cursor_right_stays_at_len ... ok
[INFO] [stdout] test plugins::tests::test_get_nonexistent ... ok
[INFO] [stdout] test plugins::tests::test_enable_nonexistent ... ok
[INFO] [stdout] test plugins::tests::test_invalid_manifest ... ok
[INFO] [stdout] test plugins::tests::test_stats ... ok
[INFO] [stdout] test plugins::tests::test_list_plugins ... ok
[INFO] [stdout] test rag::tests::test_cosine_similarity_different_lengths ... ok
[INFO] [stdout] test rag::tests::test_cosine_similarity_empty ... ok
[INFO] [stdout] test rag::tests::test_cosine_similarity_identical ... ok
[INFO] [stdout] test rag::tests::test_cosine_similarity_opposite ... ok
[INFO] [stdout] test rag::tests::test_cosine_similarity_orthogonal ... ok
[INFO] [stdout] test rag::tests::test_generate_embedding ... ignored, Requires Ollama running with nomic-embed-text-v2-moe model locally
[INFO] [stdout] test rag::tests::test_is_code_file ... ok
[INFO] [stdout] test rag::tests::test_vector_store_add_and_search ... ok
[INFO] [stdout] test rag::tests::test_vector_store_new ... ok
[INFO] [stdout] test rag::tests::test_chunk_text_small ... ok
[INFO] [stdout] test security::tests::test_validate_command_ampersand_variants ... ok
[INFO] [stdout] test rag::tests::test_chunk_text ... ok
[INFO] [stdout] test security::tests::test_validate_command_backtick_substitution ... ok
[INFO] [stdout] test security::tests::test_validate_command_dangerous ... ok
[INFO] [stdout] test security::tests::test_validate_command_dangerous_patterns ... ok
[INFO] [stdout] test security::tests::test_validate_command_dollar_paren_substitution ... ok
[INFO] [stdout] test security::tests::test_validate_command_command_substitution ... ok
[INFO] [stdout] test security::tests::test_validate_command_empty ... ok
[INFO] [stdout] test security::tests::test_validate_command_pipe_to_shell ... ok
[INFO] [stdout] test security::tests::test_validate_command_null_byte ... ok
[INFO] [stdout] test security::tests::test_validate_path_allows_subdirectory ... ok
[INFO] [stdout] test security::tests::test_validate_path_allows_safe_relative ... ok
[INFO] [stdout] test security::tests::test_validate_command_safe ... ok
[INFO] [stdout] test app::tests::vim_cursor_left_decrements ... ok
[INFO] [stdout] test security::tests::test_validate_path_null_byte ... ok
[INFO] [stdout] test security::tests::test_validate_path_tempdir ... ok
[INFO] [stdout] test sessions::tests::test_fork_auto_name ... ok
[INFO] [stdout] test security::tests::test_validate_path_prevents_traversal ... ok
[INFO] [stdout] test sessions::tests::test_fork_nonexistent ... ok
[INFO] [stdout] test sessions::tests::test_fork_duplicate_name ... ok
[INFO] [stdout] test sessions::tests::test_fork_session ... ok
[INFO] [stdout] test skills::tests::test_activate_skill ... ok
[INFO] [stdout] test security::tests::test_validate_path_valid_absolute ... ok
[INFO] [stdout] test skills::tests::test_deactivate_skill ... ok
[INFO] [stdout] test skills::tests::test_discover_changes_detects_new_skill ... ok
[INFO] [stdout] test skills::tests::test_discover_changes_detects_modified_skill ... ok
[INFO] [stdout] test skills::tests::test_discover_changes_detects_deleted_skill ... ok
[INFO] [stdout] test skills::tests::test_get_available_skills_xml_empty_when_no_skills ... ok
[INFO] [stdout] test models::tests::test_model_fetcher_urls ... ok
[INFO] [stdout] test skills::tests::test_discover_changes_preserves_active_state_of_unmodified_skills ... ok
[INFO] [stdout] test skills::tests::test_full_pipeline_from_disk_to_xml ... ok
[INFO] [stdout] test skills::tests::test_get_available_skills_xml_includes_active_only ... ok
[INFO] [stdout] test skills::tests::test_get_skill ... ok
[INFO] [stdout] test skills::tests::test_get_skill_mut ... ok
[INFO] [stdout] test skills::tests::test_list_skills_with_stats ... ok
[INFO] [stdout] test skills::tests::test_parse_skill_missing_file_returns_none ... ok
[INFO] [stdout] test skills::tests::test_parse_skill_no_frontmatter_returns_none ... ok
[INFO] [stdout] test skills::tests::test_parse_skill_full_pipeline ... ok
[INFO] [stdout] test skills::tests::test_parse_skill_invalid_yaml_returns_none ... ok
[INFO] [stdout] test skills::tests::test_skill_active_by_default_after_creation ... ok
[INFO] [stdout] test tools::tests::execute_json_array_len ... ok
[INFO] [stdout] test skills::tests::test_search_paths_includes_global ... ok
[INFO] [stdout] test skills::tests::test_skill_browser_items_sync ... ok
[INFO] [stdout] test tools::tests::execute_json_compare_equal ... ok
[INFO] [stdout] test tools::tests::execute_json_format_pretty_print ... ok
[INFO] [stdout] test tools::tests::execute_json_keys ... ok
[INFO] [stdout] test tools::tests::execute_json_path_nested ... ok
[INFO] [stdout] test tools::tests::execute_json_set_path ... ok
[INFO] [stdout] test tools::tests::execute_json_to_csv ... ok
[INFO] [stdout] test tools::tests::execute_json_validate_invalid_json ... ok
[INFO] [stdout] test tools::tests::execute_json_validate_valid_json ... ok
[INFO] [stdout] test tools::tests::execute_md_frontmatter ... ok
[INFO] [stdout] test tools::tests::execute_json_compact ... ok
[INFO] [stdout] test tools::tests::execute_md_is_valid ... ok
[INFO] [stdout] test tools::tests::execute_md_headings ... ok
[INFO] [stdout] test tools::tests::execute_md_links ... ok
[INFO] [stdout] test tools::tests::execute_md_summary ... ok
[INFO] [stdout] test tools::tests::execute_md_quotes ... ok
[INFO] [stdout] test tools::tests::execute_md_tasks ... ok
[INFO] [stdout] test tools::tests::execute_md_title ... ok
[INFO] [stdout] test tools::tests::execute_md_tables ... ok
[INFO] [stdout] test tools::tests::execute_md_title_no_title ... ok
[INFO] [stdout] test tools::tests::execute_tool_missing_arg_does_not_panic ... ok
[INFO] [stdout] test tools::tests::execute_unknown_tool_returns_error_message ... ok
[INFO] [stdout] test tools::tests::schema_contains_expected_tools ... ok
[INFO] [stdout] test tools::tests::schema_returns_valid_json ... ok
[INFO] [stdout] test tools::tests::schema_tool_has_required_fields ... ok
[INFO] [stdout] test ui::chat::tests::test_dummy_theme_values ... ok
[INFO] [stdout] test ui::chat::tests::test_sidebar_dir_format ... ok
[INFO] [stdout] test ui::chat::tests::test_sidebar_item_format ... ok
[INFO] [stdout] test ui::layout::tests::test_centered_rect_full ... ok
[INFO] [stdout] test tools::tests::execute_md_code_blocks ... ok
[INFO] [stdout] test tools::tests::execute_md_word_count ... ok
[INFO] [stdout] test ui::layout::tests::test_sidebar_layout_hidden ... ok
[INFO] [stdout] test ui::layout::tests::test_centered_rect_half ... ok
[INFO] [stdout] test ui::layout::tests::test_sidebar_layout_shown ... ok
[INFO] [stdout] test ui::layout::tests::test_main_layout_returns_four_chunks ... ok
[INFO] [stdout] test llm::tests::plan_mode_roundtrip ... ok
[INFO] [stdout] test app::tests::vim_delete_line ... ok
[INFO] [stdout] test planner::tests::create_plan_should_write_plan_md ... FAILED
[INFO] [stdout] test planner::tests::mark_step_complete_out_of_range_should_error ... ok
[INFO] [stdout] test planner::tests::mark_step_complete_should_update_plan ... ok
[INFO] [stdout] test planner::tests::mark_step_complete_should_update_plan_md ... FAILED
[INFO] [stdout] test planner::tests::multiple_plans_should_replace_previous ... ok
[INFO] [stdout] test app::tests::vim_next_word_skips_whitespace ... ok
[INFO] [stdout] test llm::tests::tool_blocked_in_plan_mode_blocks_write_tools ... ok
[INFO] [stdout] test formatters::tests::test_format_file_nonexistent_rust_file ... ok
[INFO] [stdout] test security::tests::test_validate_path_nonexistent_but_safe ... ok
[INFO] [stdout] test tool_executor::tests::test_pin_unpin ... ok
[INFO] [stdout] test llm::tests::goal_set_and_clear ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- planner::tests::create_plan_should_write_plan_md stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'planner::tests::create_plan_should_write_plan_md' (509) panicked at src/planner.rs:156:57:
[INFO] [stdout] plan.md should exist: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5caa74f09128 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5caa74f09128 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::trace_unsynchronized::<std[d054f6f42beb1baa]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5caa74f09128 - std[d054f6f42beb1baa]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5caa74f09128 - <<std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[5a1c3e9896636f3a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5caa74f223da - <core[5a1c3e9896636f3a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5caa74f223da - core[5a1c3e9896636f3a]::fmt::write
[INFO] [stdout]    6:     0x5caa74f0e5c2 - std[d054f6f42beb1baa]::io::default_write_fmt::<alloc[154f689e5dd3bae5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5caa74f0e5c2 - <alloc[154f689e5dd3bae5]::vec::Vec<u8> as std[d054f6f42beb1baa]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5caa74ee4df6 - <std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5caa74ee4df6 - std[d054f6f42beb1baa]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5caa74effff9 - std[d054f6f42beb1baa]::panicking::default_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5caa7441132c - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5caa7441132c - test[fad20673b750c10e]::test_main_inner::<test[fad20673b750c10e]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5caa74f00272 - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5caa74f00272 - std[d054f6f42beb1baa]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5caa74ee4eb2 - std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5caa74edc499 - std[d054f6f42beb1baa]::sys::backtrace::__rust_end_short_backtrace::<std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5caa74ee5d8d - __rustc[7ab284efd7f33ea8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5caa74f22c1c - core[5a1c3e9896636f3a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5caa74f22992 - core[5a1c3e9896636f3a]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x5caa743068c3 - <core[5a1c3e9896636f3a]::result::Result<alloc[154f689e5dd3bae5]::string::String, std[d054f6f42beb1baa]::io::error::Error>>::expect
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5caa74210772 - opencrust[1ca6e69c15e24792]::planner::tests::create_plan_should_write_plan_md::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:156:57
[INFO] [stdout]   22:     0x5caa7420f0b2 - opencrust[1ca6e69c15e24792]::planner::tests::run_test::<opencrust[1ca6e69c15e24792]::planner::tests::create_plan_should_write_plan_md::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:122:13
[INFO] [stdout]   23:     0x5caa741fdaa1 - opencrust[1ca6e69c15e24792]::planner::tests::with_plan_md::<opencrust[1ca6e69c15e24792]::planner::tests::run_test<opencrust[1ca6e69c15e24792]::planner::tests::create_plan_should_write_plan_md::{closure#0}>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:110:9
[INFO] [stdout]   24:     0x5caa741fe226 - opencrust[1ca6e69c15e24792]::planner::tests::run_test::<opencrust[1ca6e69c15e24792]::planner::tests::create_plan_should_write_plan_md::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:120:9
[INFO] [stdout]   25:     0x5caa7422a536 - opencrust[1ca6e69c15e24792]::planner::tests::create_plan_should_write_plan_md
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:151:9
[INFO] [stdout]   26:     0x5caa742102d7 - opencrust[1ca6e69c15e24792]::planner::tests::create_plan_should_write_plan_md::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:150:42
[INFO] [stdout]   27:     0x5caa7427b9d6 - <opencrust[1ca6e69c15e24792]::planner::tests::create_plan_should_write_plan_md::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5caa7440440b - <fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5caa7440440b - test[fad20673b750c10e]::__rust_begin_short_backtrace::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5caa74411c85 - test[fad20673b750c10e]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5caa74411c85 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5caa74411c85 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5caa74411c85 - std[d054f6f42beb1baa]::panicking::catch_unwind::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5caa74411c85 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5caa74411c85 - test[fad20673b750c10e]::run_test_in_process
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5caa74411c85 - test[fad20673b750c10e]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5caa7440c7e4 - test[fad20673b750c10e]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5caa7440c7e4 - std[d054f6f42beb1baa]::sys::backtrace::__rust_begin_short_backtrace::<test[fad20673b750c10e]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5caa74414d02 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5caa74414d02 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5caa74414d02 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5caa74414d02 - std[d054f6f42beb1baa]::panicking::catch_unwind::<(), core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5caa74414d02 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5caa74414d02 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5caa74414d02 - <std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5caa74f0809f - <alloc[154f689e5dd3bae5]::boxed::Box<dyn core[5a1c3e9896636f3a]::ops::function::FnOnce<(), Output = ()> + core[5a1c3e9896636f3a]::marker::Send> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5caa74f0809f - <std[d054f6f42beb1baa]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x775e21cb4aa4 - <unknown>
[INFO] [stdout]   49:     0x775e21d41a64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- planner::tests::mark_step_complete_should_update_plan_md stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'planner::tests::mark_step_complete_should_update_plan_md' (512) panicked at src/planner.rs:213:57:
[INFO] [stdout] plan.md should exist: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5caa74f09128 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5caa74f09128 - std[d054f6f42beb1baa]::backtrace_rs::backtrace::trace_unsynchronized::<std[d054f6f42beb1baa]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5caa74f09128 - std[d054f6f42beb1baa]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5caa74f09128 - <<std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[5a1c3e9896636f3a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5caa74f223da - <core[5a1c3e9896636f3a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5caa74f223da - core[5a1c3e9896636f3a]::fmt::write
[INFO] [stdout]    6:     0x5caa74f0e5c2 - std[d054f6f42beb1baa]::io::default_write_fmt::<alloc[154f689e5dd3bae5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5caa74f0e5c2 - <alloc[154f689e5dd3bae5]::vec::Vec<u8> as std[d054f6f42beb1baa]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5caa74ee4df6 - <std[d054f6f42beb1baa]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5caa74ee4df6 - std[d054f6f42beb1baa]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5caa74effff9 - std[d054f6f42beb1baa]::panicking::default_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5caa7441132c - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5caa7441132c - test[fad20673b750c10e]::test_main_inner::<test[fad20673b750c10e]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5caa74f00272 - <alloc[154f689e5dd3bae5]::boxed::Box<dyn for<'a, 'b> core[5a1c3e9896636f3a]::ops::function::Fn<(&'a std[d054f6f42beb1baa]::panic::PanicHookInfo<'b>,), Output = ()> + core[5a1c3e9896636f3a]::marker::Send + core[5a1c3e9896636f3a]::marker::Sync> as core[5a1c3e9896636f3a]::ops::function::Fn<(&std[d054f6f42beb1baa]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5caa74f00272 - std[d054f6f42beb1baa]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5caa74ee4eb2 - std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5caa74edc499 - std[d054f6f42beb1baa]::sys::backtrace::__rust_end_short_backtrace::<std[d054f6f42beb1baa]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5caa74ee5d8d - __rustc[7ab284efd7f33ea8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5caa74f22c1c - core[5a1c3e9896636f3a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5caa74f22992 - core[5a1c3e9896636f3a]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x5caa743068c3 - <core[5a1c3e9896636f3a]::result::Result<alloc[154f689e5dd3bae5]::string::String, std[d054f6f42beb1baa]::io::error::Error>>::expect
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5caa74211c6c - opencrust[1ca6e69c15e24792]::planner::tests::mark_step_complete_should_update_plan_md::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:213:57
[INFO] [stdout]   22:     0x5caa7420f2f2 - opencrust[1ca6e69c15e24792]::planner::tests::run_test::<opencrust[1ca6e69c15e24792]::planner::tests::mark_step_complete_should_update_plan_md::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:122:13
[INFO] [stdout]   23:     0x5caa741fdf61 - opencrust[1ca6e69c15e24792]::planner::tests::with_plan_md::<opencrust[1ca6e69c15e24792]::planner::tests::run_test<opencrust[1ca6e69c15e24792]::planner::tests::mark_step_complete_should_update_plan_md::{closure#0}>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:110:9
[INFO] [stdout]   24:     0x5caa741fe266 - opencrust[1ca6e69c15e24792]::planner::tests::run_test::<opencrust[1ca6e69c15e24792]::planner::tests::mark_step_complete_should_update_plan_md::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:120:9
[INFO] [stdout]   25:     0x5caa7422a576 - opencrust[1ca6e69c15e24792]::planner::tests::mark_step_complete_should_update_plan_md
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:207:9
[INFO] [stdout]   26:     0x5caa74210397 - opencrust[1ca6e69c15e24792]::planner::tests::mark_step_complete_should_update_plan_md::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/planner.rs:206:50
[INFO] [stdout]   27:     0x5caa7427bad6 - <opencrust[1ca6e69c15e24792]::planner::tests::mark_step_complete_should_update_plan_md::{closure#0} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5caa7440440b - <fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5caa7440440b - test[fad20673b750c10e]::__rust_begin_short_backtrace::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, fn() -> core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:724:18
[INFO] [stdout]   30:     0x5caa74411c85 - test[fad20673b750c10e]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:74
[INFO] [stdout]   31:     0x5caa74411c85 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   32:     0x5caa74411c85 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   33:     0x5caa74411c85 - std[d054f6f42beb1baa]::panicking::catch_unwind::<core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>, core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   34:     0x5caa74411c85 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<test[fad20673b750c10e]::run_test_in_process::{closure#0}>, core[5a1c3e9896636f3a]::result::Result<(), alloc[154f689e5dd3bae5]::string::String>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   35:     0x5caa74411c85 - test[fad20673b750c10e]::run_test_in_process
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:747:27
[INFO] [stdout]   36:     0x5caa74411c85 - test[fad20673b750c10e]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:668:43
[INFO] [stdout]   37:     0x5caa7440c7e4 - test[fad20673b750c10e]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/test/src/lib.rs:698:41
[INFO] [stdout]   38:     0x5caa7440c7e4 - std[d054f6f42beb1baa]::sys::backtrace::__rust_begin_short_backtrace::<test[fad20673b750c10e]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   39:     0x5caa74414d02 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   40:     0x5caa74414d02 - <core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   41:     0x5caa74414d02 - std[d054f6f42beb1baa]::panicking::catch_unwind::do_call::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:581:40
[INFO] [stdout]   42:     0x5caa74414d02 - std[d054f6f42beb1baa]::panicking::catch_unwind::<(), core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panicking.rs:544:19
[INFO] [stdout]   43:     0x5caa74414d02 - std[d054f6f42beb1baa]::panic::catch_unwind::<core[5a1c3e9896636f3a]::panic::unwind_safe::AssertUnwindSafe<std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/panic.rs:359:14
[INFO] [stdout]   44:     0x5caa74414d02 - std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked::<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   45:     0x5caa74414d02 - <std[d054f6f42beb1baa]::thread::lifecycle::spawn_unchecked<test[fad20673b750c10e]::run_test::{closure#1}, ()>::{closure#1} as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5caa74f0809f - <alloc[154f689e5dd3bae5]::boxed::Box<dyn core[5a1c3e9896636f3a]::ops::function::FnOnce<(), Output = ()> + core[5a1c3e9896636f3a]::marker::Send> as core[5a1c3e9896636f3a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   47:     0x5caa74f0809f - <std[d054f6f42beb1baa]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/28f95933dc0727396c07b48d0a8cb5211b7fb472/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   48:     0x775e21cb4aa4 - <unknown>
[INFO] [stdout]   49:     0x775e21d41a64 - clone
[INFO] [stdout]   50:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     planner::tests::create_plan_should_write_plan_md
[INFO] [stdout]     planner::tests::mark_step_complete_should_update_plan_md
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 349 passed; 2 failed; 1 ignored; 0 measured; 0 filtered out; finished in 3.04s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--bin opencrust`
[INFO] running `Command { std: "docker" "inspect" "4c94f15cc7b5e435bc9906f6b7d66ab9570cd04362d47bf5d9469ae8cb464cc4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4c94f15cc7b5e435bc9906f6b7d66ab9570cd04362d47bf5d9469ae8cb464cc4", kill_on_drop: false }`
[INFO] [stdout] 4c94f15cc7b5e435bc9906f6b7d66ab9570cd04362d47bf5d9469ae8cb464cc4
